← Index
NYTProf Performance Profile   
For starman worker -M FindBin --max-requests 50 --workers 2 --user=kohadev-koha --group kohadev-koha --pid /var/run/koha/kohadev/plack.pid --daemonize --access-log /var/log/koha/kohadev/plack.log --error-log /var/log/koha/kohadev/plack-error.log -E deployment --socket /var/run/koha/kohadev/plack.sock /etc/koha/sites/kohadev/plack.psgi
  Run on Fri Jan 8 13:50:58 2016
Reported on Fri Jan 8 13:51:28 2016

Filename(eval 190)[/usr/share/perl5/Sub/Quote.pm:5]
StatementsExecuted 276 statements in 803µs
Eval Invoked At/usr/share/perl5/Sub/Quote.pm line 5
Sibling evals1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14
Subroutines
Calls P F Exclusive
Time
Inclusive
Time
Subroutine
13433994µs2.52msDBIx::Class::ResultSourceProxy::::column_infoDBIx::Class::ResultSourceProxy::column_info
11111µs18µsSub::Quote::::BEGIN@4.65 Sub::Quote::BEGIN@4.65
1119µs9µsDBIx::Class::ResultSourceProxy::::BEGIN@10.66DBIx::Class::ResultSourceProxy::BEGIN@10.66
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments
Time
on line
Calls Time
in subs
Code
1{
221µs my $_UNQUOTED = ${$_[1]->{"\$_UNQUOTED"}};
31200ns my $_QUOTED = ${$_[1]->{"\$_QUOTED"}};
4281µs226µs
# spent 18µs (11+8) within Sub::Quote::BEGIN@4.65 which was called: # once (11µs+8µs) by Sub::Quote::_clean_eval at line 4
no warnings 'closure';
# spent 18µs making 1 call to Sub::Quote::BEGIN@4.65 # spent 8µs making 1 call to warnings::unimport
5
# spent 2.52ms (994µs+1.53) within DBIx::Class::ResultSourceProxy::column_info which was called 134 times, avg 19µs/call: # 122 times (895µs+1.40ms) by DBIx::Class::InflateColumn::inflate_column at line 90 of DBIx/Class/InflateColumn.pm, avg 19µs/call # 11 times (89µs+107µs) by DBIx::Class::Relationship::HasOne::_validate_has_one_condition at line 101 of DBIx/Class/Relationship/HasOne.pm, avg 18µs/call # once (10µs+18µs) by DBIx::Class::InflateColumn::inflate_column at line 56 of Sub/Defer.pm
sub DBIx::Class::ResultSourceProxy::column_info {
6 $_QUOTED if 0;
713314µs $_UNQUOTED if 0;
8# BEGIN quote_sub PRELUDE
9package DBIx::Class::ResultSourceProxy;
10
# spent 9µs within DBIx::Class::ResultSourceProxy::BEGIN@10.66 which was called: # once (9µs+0s) by Sub::Quote::_clean_eval at line 15
BEGIN {
111900ns $^H = "132834";
121400ns ${^WARNING_BITS} = "UUUUUUUUUUUUUUUUU";
1315µs %^H = (
14 );
15155µs19µs}
# spent 9µs making 1 call to DBIx::Class::ResultSourceProxy::BEGIN@10.66
16# END quote_sub PRELUDE
17 DBIx::Class::_ENV_::ASSERT_NO_INTERNAL_INDIRECT_CALLS and DBIx::Class::_Util::fail_on_internal_call;
18133642µs2661.24ms shift->result_source_instance->column_info (@_);
# spent 383µs making 133 calls to DBIx::Class::ResultSource::column_info, avg 3µs/call # spent 45µs making 8 calls to Koha::Schema::Result::Item::result_source_instance, avg 6µs/call # spent 45µs making 6 calls to Koha::Schema::Result::Aqorder::result_source_instance, avg 7µs/call # spent 35µs making 4 calls to Koha::Schema::Result::HoldFillTarget::result_source_instance, avg 9µs/call # spent 24µs making 4 calls to Koha::Schema::Result::Aqbasket::result_source_instance, avg 6µs/call # spent 24µs making 4 calls to Koha::Schema::Result::Reserve::result_source_instance, avg 6µs/call # spent 19µs making 3 calls to Koha::Schema::Result::CreatorBatch::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Borrower::result_source_instance, avg 6µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Branchtransfer::result_source_instance, avg 6µs/call # spent 18µs making 2 calls to Koha::Schema::Result::MessageQueue::result_source_instance, avg 9µs/call # spent 18µs making 3 calls to Koha::Schema::Result::Virtualshelfcontent::result_source_instance, avg 6µs/call # spent 17µs making 3 calls to Koha::Schema::Result::OldReserve::result_source_instance, avg 6µs/call # spent 15µs making 2 calls to Koha::Schema::Result::OpacNews::result_source_instance, avg 8µs/call # spent 14µs making 1 call to Koha::Schema::Result::TagsApproval::result_source_instance # spent 14µs making 2 calls to Koha::Schema::Result::Accountline::result_source_instance, avg 7µs/call # spent 13µs making 2 calls to Koha::Schema::Result::Virtualshelfshare::result_source_instance, avg 7µs/call # spent 13µs making 2 calls to Koha::Schema::Result::BorrowerMessagePreference::result_source_instance, avg 7µs/call # spent 13µs making 2 calls to Koha::Schema::Result::BorrowerAttributeTypesBranch::result_source_instance, avg 7µs/call # spent 13µs making 2 calls to Koha::Schema::Result::Subscription::result_source_instance, avg 6µs/call # spent 13µs making 2 calls to Koha::Schema::Result::Aqbasketuser::result_source_instance, avg 6µs/call # spent 13µs making 2 calls to Koha::Schema::Result::Issue::result_source_instance, avg 6µs/call # spent 13µs making 2 calls to Koha::Schema::Result::Branchrelation::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Aqbookseller::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::CourseItem::result_source_instance, avg 6µs/call # spent 12µs making 1 call to Koha::Schema::Result::ItemsSearchField::result_source_instance # spent 12µs making 2 calls to Koha::Schema::Result::TagsIndex::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Subscriptionroutinglist::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Review::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::AqorderUser::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::TransportCost::result_source_instance, avg 6µs/call # spent 12µs making 1 call to Koha::Schema::Result::Aqbasketgroup::result_source_instance # spent 12µs making 2 calls to Koha::Schema::Result::AqordersTransfer::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::BranchItemRule::result_source_instance, avg 6µs/call # spent 12µs making 2 calls to Koha::Schema::Result::Rating::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::Aqinvoice::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::BranchBorrowerCircRule::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::ItemsLastBorrower::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::BorrowerAttribute::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::TagAll::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::CategoriesBranch::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::Serialitem::result_source_instance, avg 6µs/call # spent 11µs making 2 calls to Koha::Schema::Result::OldIssue::result_source_instance, avg 5µs/call # spent 9µs making 1 call to Koha::Schema::Result::Branch::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::AuthorisedValuesBranch::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Biblioimage::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::DefaultBorrowerCircRule::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::AuthSubfieldStructure::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::DefaultBranchCircRule::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::Discharge::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::DefaultBranchItemRule::result_source_instance # spent 7µs making 1 call to Koha::Schema::Result::UserPermission::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Aqcontract::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::PatronListPatron::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::AuthTagStructure::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerDebarment::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerFile::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerMessageTransportPreference::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::CourseInstructor::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Aqbudgetborrower::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::OverduerulesTransportType::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::PatronList::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Accountoffset::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::ClassSource::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Virtualshelve::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::AqordersItem::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::MessageTransport::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Suggestion::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Aqcontact::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::BorrowerSync::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Letter::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Permission::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Biblioitem::result_source_instance # spent 6µs making 1 call to Koha::Schema::Result::Patronimage::result_source_instance # spent 5µs making 1 call to Koha::Schema::Result::Itemtype::result_source_instance # spent 5µs making 1 call to Koha::Schema::Result::Category::result_source_instance
19 }
2011µs $$_UNQUOTED = \&DBIx::Class::ResultSourceProxy::column_info
21}
2213µs1;
23
24;